← Index
NYTProf Performance Profile   
For starman worker -M FindBin --max-requests 50 --workers 2 --user=kohadev-koha --group kohadev-koha --pid /var/run/koha/kohadev/plack.pid --daemonize --access-log /var/log/koha/kohadev/plack.log --error-log /var/log/koha/kohadev/plack-error.log -E deployment --socket /var/run/koha/kohadev/plack.sock /etc/koha/sites/kohadev/plack.psgi
  Run on Fri Jan 8 13:01:18 2016
Reported on Fri Jan 8 13:01:35 2016

Filename(eval 203)[/usr/share/perl5/Sub/Quote.pm:5]
StatementsExecuted 848 statements in 5.40ms
Eval Invoked At/usr/share/perl5/Sub/Quote.pm line 5
Sibling evals1, 2, 3, 4, 5, 6, 7, 8, 9, 10, 11, 12, 13, 14
Subroutines
Calls P F Exclusive
Time
Inclusive
Time
Subroutine
420652.22ms6.42msDBIx::Class::ResultSourceProxy::::has_columnDBIx::Class::ResultSourceProxy::has_column
11115µs33µsSub::Quote::::BEGIN@4.107 Sub::Quote::BEGIN@4.107
1118µs8µsDBIx::Class::ResultSourceProxy::::BEGIN@10.108DBIx::Class::ResultSourceProxy::BEGIN@10.108
Call graph for these subroutines as a Graphviz dot language file.
Line State
ments
Time
on line
Calls Time
in subs
Code
1{
221µs my $_UNQUOTED = ${$_[1]->{"\$_UNQUOTED"}};
31200ns my $_QUOTED = ${$_[1]->{"\$_QUOTED"}};
42108µs252µs
# spent 33µs (15+18) within Sub::Quote::BEGIN@4.107 which was called: # once (15µs+18µs) by Sub::Quote::_clean_eval at line 4
no warnings 'closure';
# spent 33µs making 1 call to Sub::Quote::BEGIN@4.107 # spent 18µs making 1 call to warnings::unimport
5
# spent 6.42ms (2.22+4.20) within DBIx::Class::ResultSourceProxy::has_column which was called 420 times, avg 15µs/call: # 162 times (1.13ms+2.26ms) by DBIx::Class::Relationship::BelongsTo::belongs_to at line 76 of DBIx/Class/Relationship/BelongsTo.pm, avg 21µs/call # 122 times (498µs+972µs) by DBIx::Class::Relationship::Accessor::add_relationship_accessor at line 63 of DBIx/Class/Relationship/Accessor.pm, avg 12µs/call # 122 times (505µs+820µs) by DBIx::Class::InflateColumn::inflate_column at line 95 of DBIx/Class/InflateColumn.pm, avg 11µs/call # 11 times (72µs+112µs) by DBIx::Class::Relationship::HasOne::_validate_has_one_condition at line 99 of DBIx/Class/Relationship/HasOne.pm, avg 17µs/call # 2 times (8µs+17µs) by DBIx::Class::Relationship::BelongsTo::belongs_to at line 39 of DBIx/Class/Relationship/BelongsTo.pm, avg 13µs/call # once (7µs+19µs) by DBIx::Class::Relationship::BelongsTo::belongs_to at line 56 of Sub/Defer.pm
sub DBIx::Class::ResultSourceProxy::has_column {
6 $_QUOTED if 0;
741928µs $_UNQUOTED if 0;
8# BEGIN quote_sub PRELUDE
9package DBIx::Class::ResultSourceProxy;
10
# spent 8µs within DBIx::Class::ResultSourceProxy::BEGIN@10.108 which was called: # once (8µs+0s) by Sub::Quote::_clean_eval at line 15
BEGIN {
1112µs $^H = "132834";
121500ns ${^WARNING_BITS} = "UUUUUUUUUUUUUUUUU";
1315µs %^H = (
14 );
15169µs18µs}
# spent 8µs making 1 call to DBIx::Class::ResultSourceProxy::BEGIN@10.108
16# END quote_sub PRELUDE
17 DBIx::Class::_ENV_::ASSERT_NO_INTERNAL_INDIRECT_CALLS and DBIx::Class::_Util::fail_on_internal_call;
184195.18ms8383.74ms shift->result_source_instance->has_column (@_);
# spent 825µs making 419 calls to DBIx::Class::ResultSource::has_column, avg 2µs/call # spent 122µs making 17 calls to Koha::Schema::Result::Item::result_source_instance, avg 7µs/call # spent 96µs making 15 calls to Koha::Schema::Result::Aqorder::result_source_instance, avg 6µs/call # spent 84µs making 12 calls to Koha::Schema::Result::Aqbasket::result_source_instance, avg 7µs/call # spent 82µs making 9 calls to Koha::Schema::Result::Branchtransfer::result_source_instance, avg 9µs/call # spent 80µs making 6 calls to Koha::Schema::Result::TagsIndex::result_source_instance, avg 13µs/call # spent 79µs making 9 calls to Koha::Schema::Result::Issue::result_source_instance, avg 9µs/call # spent 77µs making 14 calls to Koha::Schema::Result::Reserve::result_source_instance, avg 5µs/call # spent 72µs making 12 calls to Koha::Schema::Result::HoldFillTarget::result_source_instance, avg 6µs/call # spent 69µs making 9 calls to Koha::Schema::Result::CreatorBatch::result_source_instance, avg 8µs/call # spent 67µs making 9 calls to Koha::Schema::Result::OldReserve::result_source_instance, avg 7µs/call # spent 58µs making 9 calls to Koha::Schema::Result::Virtualshelfcontent::result_source_instance, avg 6µs/call # spent 55µs making 6 calls to Koha::Schema::Result::BranchItemRule::result_source_instance, avg 9µs/call # spent 54µs making 6 calls to Koha::Schema::Result::Review::result_source_instance, avg 9µs/call # spent 50µs making 6 calls to Koha::Schema::Result::AqordersTransfer::result_source_instance, avg 8µs/call # spent 47µs making 8 calls to Koha::Schema::Result::Borrower::result_source_instance, avg 6µs/call # spent 46µs making 6 calls to Koha::Schema::Result::BorrowerAttributeTypesBranch::result_source_instance, avg 8µs/call # spent 45µs making 6 calls to Koha::Schema::Result::CourseItem::result_source_instance, avg 7µs/call # spent 44µs making 6 calls to Koha::Schema::Result::BranchBorrowerCircRule::result_source_instance, avg 7µs/call # spent 42µs making 7 calls to Koha::Schema::Result::BorrowerMessagePreference::result_source_instance, avg 6µs/call # spent 41µs making 6 calls to Koha::Schema::Result::Subscription::result_source_instance, avg 7µs/call # spent 40µs making 6 calls to Koha::Schema::Result::Aqbasketuser::result_source_instance, avg 7µs/call # spent 40µs making 6 calls to Koha::Schema::Result::Aqbookseller::result_source_instance, avg 7µs/call # spent 40µs making 6 calls to Koha::Schema::Result::BorrowerAttribute::result_source_instance, avg 7µs/call # spent 38µs making 6 calls to Koha::Schema::Result::OldIssue::result_source_instance, avg 6µs/call # spent 36µs making 6 calls to Koha::Schema::Result::Virtualshelfshare::result_source_instance, avg 6µs/call # spent 35µs making 6 calls to Koha::Schema::Result::AqorderUser::result_source_instance, avg 6µs/call # spent 35µs making 6 calls to Koha::Schema::Result::Serialitem::result_source_instance, avg 6µs/call # spent 34µs making 6 calls to Koha::Schema::Result::Accountline::result_source_instance, avg 6µs/call # spent 34µs making 6 calls to Koha::Schema::Result::Branchrelation::result_source_instance, avg 6µs/call # spent 34µs making 6 calls to Koha::Schema::Result::ItemsLastBorrower::result_source_instance, avg 6µs/call # spent 34µs making 6 calls to Koha::Schema::Result::OpacNews::result_source_instance, avg 6µs/call # spent 34µs making 6 calls to Koha::Schema::Result::MessageQueue::result_source_instance, avg 6µs/call # spent 34µs making 6 calls to Koha::Schema::Result::Subscriptionroutinglist::result_source_instance, avg 6µs/call # spent 34µs making 6 calls to Koha::Schema::Result::TransportCost::result_source_instance, avg 6µs/call # spent 33µs making 6 calls to Koha::Schema::Result::CategoriesBranch::result_source_instance, avg 6µs/call # spent 33µs making 4 calls to Koha::Schema::Result::BorrowerMessageTransportPreference::result_source_instance, avg 8µs/call # spent 33µs making 6 calls to Koha::Schema::Result::Aqinvoice::result_source_instance, avg 5µs/call # spent 33µs making 6 calls to Koha::Schema::Result::Rating::result_source_instance, avg 5µs/call # spent 32µs making 6 calls to Koha::Schema::Result::TagAll::result_source_instance, avg 5µs/call # spent 31µs making 4 calls to Koha::Schema::Result::OverduerulesTransportType::result_source_instance, avg 8µs/call # spent 31µs making 4 calls to Koha::Schema::Result::AuthorisedValuesBranch::result_source_instance, avg 8µs/call # spent 30µs making 5 calls to Koha::Schema::Result::Biblioitem::result_source_instance, avg 6µs/call # spent 28µs making 3 calls to Koha::Schema::Result::Aqcontact::result_source_instance, avg 9µs/call # spent 28µs making 3 calls to Koha::Schema::Result::Aqcontract::result_source_instance, avg 9µs/call # spent 26µs making 3 calls to Koha::Schema::Result::AuthSubfieldStructure::result_source_instance, avg 9µs/call # spent 26µs making 3 calls to Koha::Schema::Result::DefaultBranchCircRule::result_source_instance, avg 9µs/call # spent 26µs making 4 calls to Koha::Schema::Result::PatronListPatron::result_source_instance, avg 6µs/call # spent 25µs making 4 calls to Koha::Schema::Result::Aqbudgetborrower::result_source_instance, avg 6µs/call # spent 24µs making 1 call to Koha::Schema::Result::ImportBiblio::result_source_instance # spent 24µs making 3 calls to Koha::Schema::Result::Accountoffset::result_source_instance, avg 8µs/call # spent 24µs making 4 calls to Koha::Schema::Result::CourseInstructor::result_source_instance, avg 6µs/call # spent 24µs making 4 calls to Koha::Schema::Result::MessageTransport::result_source_instance, avg 6µs/call # spent 22µs making 2 calls to Koha::Schema::Result::CourseReserve::result_source_instance, avg 11µs/call # spent 22µs making 2 calls to Koha::Schema::Result::MatcherMatchpoint::result_source_instance, avg 11µs/call # spent 21µs making 3 calls to Koha::Schema::Result::Matchcheck::result_source_instance, avg 7µs/call # spent 20µs making 3 calls to Koha::Schema::Result::Permission::result_source_instance, avg 7µs/call # spent 19µs making 1 call to Koha::Schema::Result::Matchpoint::result_source_instance # spent 19µs making 3 calls to Koha::Schema::Result::BorrowerFile::result_source_instance, avg 6µs/call # spent 19µs making 3 calls to Koha::Schema::Result::AqordersItem::result_source_instance, avg 6µs/call # spent 18µs making 3 calls to Koha::Schema::Result::DefaultBorrowerCircRule::result_source_instance, avg 6µs/call # spent 18µs making 3 calls to Koha::Schema::Result::ItemsSearchField::result_source_instance, avg 6µs/call # spent 18µs making 3 calls to Koha::Schema::Result::Letter::result_source_instance, avg 6µs/call # spent 18µs making 3 calls to Koha::Schema::Result::Patronimage::result_source_instance, avg 6µs/call # spent 18µs making 3 calls to Koha::Schema::Result::Suggestion::result_source_instance, avg 6µs/call # spent 18µs making 3 calls to Koha::Schema::Result::Virtualshelve::result_source_instance, avg 6µs/call # spent 18µs making 3 calls to Koha::Schema::Result::AuthTagStructure::result_source_instance, avg 6µs/call # spent 18µs making 3 calls to Koha::Schema::Result::ClassSource::result_source_instance, avg 6µs/call # spent 18µs making 3 calls to Koha::Schema::Result::DefaultBranchItemRule::result_source_instance, avg 6µs/call # spent 18µs making 3 calls to Koha::Schema::Result::Biblioimage::result_source_instance, avg 6µs/call # spent 18µs making 3 calls to Koha::Schema::Result::Aqbasketgroup::result_source_instance, avg 6µs/call # spent 18µs making 3 calls to Koha::Schema::Result::BorrowerDebarment::result_source_instance, avg 6µs/call # spent 18µs making 3 calls to Koha::Schema::Result::PatronList::result_source_instance, avg 6µs/call # spent 18µs making 3 calls to Koha::Schema::Result::BorrowerSync::result_source_instance, avg 6µs/call # spent 18µs making 3 calls to Koha::Schema::Result::Discharge::result_source_instance, avg 6µs/call # spent 18µs making 3 calls to Koha::Schema::Result::TagsApproval::result_source_instance, avg 6µs/call # spent 18µs making 3 calls to Koha::Schema::Result::UserPermission::result_source_instance, avg 6µs/call # spent 13µs making 1 call to Koha::Schema::Result::OaiSetsMapping::result_source_instance # spent 12µs making 1 call to Koha::Schema::Result::ImportAuth::result_source_instance # spent 12µs making 1 call to Koha::Schema::Result::ImportRecordMatch::result_source_instance # spent 12µs making 1 call to Koha::Schema::Result::MatchpointComponentNorm::result_source_instance # spent 11µs making 1 call to Koha::Schema::Result::ImportItem::result_source_instance # spent 9µs making 1 call to Koha::Schema::Result::MarcModificationTemplateAction::result_source_instance # spent 9µs making 1 call to Koha::Schema::Result::Category::result_source_instance # spent 8µs making 1 call to Koha::Schema::Result::Collection::result_source_instance # spent 8µs making 1 call to Koha::Schema::Result::ImportRecord::result_source_instance # spent 7µs making 1 call to Koha::Schema::Result::Itemtype::result_source_instance # spent 7µs making 1 call to Koha::Schema::Result::AqbudgetsPlanning::result_source_instance # spent 7µs making 1 call to Koha::Schema::Result::MatchpointComponent::result_source_instance # spent 7µs making 1 call to Koha::Schema::Result::OaiSetsBiblio::result_source_instance # spent 7µs making 1 call to Koha::Schema::Result::Branch::result_source_instance # spent 7µs making 1 call to Koha::Schema::Result::OaiSetsDescription::result_source_instance # spent 7µs making 1 call to Koha::Schema::Result::AdditionalFieldValue::result_source_instance
19 }
2011µs $$_UNQUOTED = \&DBIx::Class::ResultSourceProxy::has_column
21}
2214µs1;
23
24;